SMG-2. SN: VI45000362 Software Version: V.3.1.1.1089. 2/S Build: Jun 30 2015 10:43:35
Log started at:  12:22:04.594059
  12:22:10.722998  [INFO]  PBXIPC-SIP. RX. seize 
  12:22:10.723147  [INFO]  		# callref 0002
  12:22:10.723233  [INFO]  		# Call-ID len (45)
  12:22:10.723311  [INFO]  		# trunkID 01
  12:22:10.723388  [INFO]  		# Hostname '1.1.1.1'
  12:22:10.723466  [INFO]  		# Display name ''
  12:22:10.723541  [INFO]  		# port 5060
  12:22:10.723618  [INFO]  		# local-port 5060
  12:22:10.723695  [INFO]  		# cdpn <4952222222> (10) cat 0x00
  12:22:10.723773  [INFO]  		# cgpn <4951111111> (10) cat 0x0a
  12:22:10.723851  [INFO]  		# SDP len (264)
  12:22:10.724168  [INFO]  SIP. Callref 0002. Seize in state 'SIPT_IDLE'. Call-ID: 46430d00f7405d108000001ec9d80b7a@mera2.kcs.ru
  12:22:10.724384  [INFO]  SIP. Callref 0002. Incoming seize mode 'SIP': 
			# source host <1.1.1.1:5060> 
			# interface ID 1
			# Port SIPT:0002. UserID -1. Intf: 0.
			# CCR(none). Category 00 (0). 
			# Calling <4951111111> Called <4952222222> Generic <none> GenericNotify 0x0
			# Orig called <none> Redirecting <none>. Redirecting count 0
			# cplt=y; pres=allow; nature=not subscriber. 
			# SS7 all way: n, ISUP all way: n, ISDN orig: n, ISDN pref: 0
			# RADIUS: seize-time [0x00000000], return-code [0x0] credit-time [0x0] [FAIL]
			# RADIUS: session-ID [11000002 00000000 00000000 00000000]
			RawCalled: -not filled-
  12:22:10.724755  [INFO]  SIP. Callref 0002. Interface 0, mode 0, reg-mode 0, sdp_18x: 0
  12:22:10.724853  [INFO]  SIP. Callref 0002. Set category-AON: cat <7>
  12:22:10.724982  [INFO]  Port SIPT:0002. Seize (v-chan:-1, TG:01). Cld:'<none>'(0). (Trunk) . Clg-len: 10. clg-set: y. COS: -1. RADIUS=-1
  12:22:10.725087  [INFO]  Port SIPT:0002. ModTable in [-1:-1], out [-1:-1], out redir:orig [-1:-1]
  12:22:10.725186  [INFO]  Port SIPT:0002. CgPN: <4951111111>(10) <set> <unknown> <isdn/telephony> <allowed> <net.provided>. cat [7].
  12:22:10.725282  [INFO]  Port SIPT:0002. Calling number: 4951111111, use for sorm: all digits
  12:22:10.725389  [INFO]  Port SIPT:0002. Calling Info (pres allowed): <4951111111>. <Category=7>
  12:22:10.725477  [INFO]  Port SIPT:0002. CgPN: <4951111111>(10) <set> <unknown> <isdn/telephony> <allowed> <net.provided>. cat [7].
  12:22:10.725572  [INFO]  Port SIPT:0002. Set state 'In-Dial'
  12:22:10.725656  [INFO]  SIP. Callref 0002. Set display name: <>(0)
  12:22:10.725738  [INFO]  SIP. Callref 0002. No IP-conn used at this point
  12:22:10.725820  [INFO]  SIP. Callref 0002. Incoming seize - PLAN 0. COS 0
  12:22:10.725935  [INFO]  Port SIPT:0002. Dial In (in=11, need rm=0): <8><4><3><2><0><2><2><7><0><0>[.]
  12:22:10.726028  [INFO]  Port SIPT:0002. In Digit 11 sym: called buf <4952222222.>
  12:22:10.726112  [INFO]  Port SIPT:0002. Dial buf <4952222222>(10)
  12:22:10.726196  [INFO]  Port SIPT:0002. SIPT seize chan. Prefix data: type: not set;  called type: unknown
  12:22:10.726279  [INFO]  SIP. Callref 0002. New state 'SIPT_PRESENTED'
  12:22:10.726473  [INFO]  SIP. Callref 0002. Set Media-Info. Count[1]
  12:22:10.726580  [INFO]  SIP. Callref 0002. Media-Info (offer): [1.1.1.1]
  12:22:10.726675  [INFO]  SIP. Callref 0002. 
			 o= 0 0 IN IP4 1.1.1.1
			 c=IN IP4 1.1.1.1
  12:22:10.726857  [INFO]  Valid
			 m[0]: audio 41652 RTP/AVP 8 18 4 0 101
			 a[0]:8 G711.A [set]
			 a[1]:18 G729.B [set]
			 a[2]:4 G723.1 (5.3 kbps) [set]
			 a[3]:0 G711.U [set]
			 a[4]:101 NTE [set]
			 a[5]:sendrecv
  12:22:10.727103  [INFO]  SIP. Callref 0002. New state 'SIPT_IN_PROCEEDING'
  12:22:10.727203  [INFO]  Port SIPT:0002. Dial Proc (state In-Dial, called len 11): <4952222222.> Compl.
  12:22:10.727292  [INFO]  Port SIPT:0002. Dial Proc. RADIUS return-code: [0].
  12:22:10.727379  [INFO]  Port SIPT:0002. Search route. Mode: 'calling'. Num: <4951111111>(10).# Complete
  12:22:10.727492  [INFO]  Port SIPT:0002. Search route. result: route not found. res (-1)
  12:22:10.727586  [INFO]  Port SIPT:0002. Search route. Mode: 'called'. Num: <4952222222.>(11).# Complete
  12:22:10.727969  [INFO]  Port SIPT:0002. Search route. result: found route: prefix[0]. res (10)
  12:22:10.728084  [INFO]  Port SIPT:0002. Process route to prefix[0].
  12:22:10.728176  [INFO]  Port SIPT:0002. Prefix 00: type 'TRUNK', idx=0
  12:22:10.728264  [INFO]  Port SIPT:0002. Accs on
  12:22:10.728348  [INFO]  Port SIPT:0002. Link to TG 00. Out modifiers-table: clg[-1], cld[-1], redir[-1], orig[-1], generic[-1]
  12:22:10.728440  [INFO]  Port SIPT:0002. Link to TG 00. Setup mode: overlap
  12:22:10.728526  [INFO]  Port SIPT:0002. Link to TG 00: mode Local. Dir=0, Cld=2 Select Order=. In buf: ~4952222222.
  12:22:10.728616  [INFO]  Port SIPT:0002. TG: Link entry to PRI[00:00]
  12:22:10.728707  [INFO]  Port SIPT:0002. Outgoing setup to PRI:00:00.
  12:22:10.728809  [INFO]  Q931. Line 00/00. Create Call, entry 00. Call 0004 (Outgoing)
  12:22:10.728922  [INFO]  Port PRI:00:00. Set parent SIPT:0002
  12:22:10.729021  [INFO]  Port PRI:00:00. loc 0, trunk-loc 0. peer-loc 0, peer-tunk-loc: 0
  12:22:10.729109  [INFO]  Port SIPT:0002. loc 0, trunk-loc 0. peer-loc 0, peer-tunk-loc: 0
  12:22:10.729197  [INFO]  Port SIPT:0002. Dir[0], obci-req[0]. Call-ind: change[0], obci[0:0], isdn: allway[0], fwd_acs[0]
  12:22:10.729294  [INFO]  Port PRI:00:00. Check out-calling-modify: '4951111111'(10). ModTable[-1], ClgMod[-1]
  12:22:10.729401  [INFO]  Port PRI:00:00. Check out-calling-from-RADIUS-modify: '4951111111'(10). ClgMod[-1]
  12:22:10.729515  [INFO]  Q931. Line 00/00. Search B-chan: chan -1, mask 00010001 {chan mask: 00010001} - chan order: 0, dir=1, start=3
  12:22:10.729602  [INFO]  Q931. Line 00/00. Search B-chan: chan -1, mask 00010001 {chan mask: 00010001} - found free chan 3 (cycle dir 1)
  12:22:10.729688  [INFO]  Q931, Line 00/00. Call 0004. Create Seize - entry 00, channel 03
  12:22:10.729775  [INFO]  Q931. Line 00/00. Call 0004. State 'Call Initiated'
  12:22:10.729862  [INFO]  Q931. Line 00/00. Call 0004. Timer 'T303' (4 sec) start in state 'Call Initiated'
  12:22:10.729954  [INFO]  Port PRI:00:00. VChan set (chan 3) - 'line 0, chan 12'
  12:22:10.730043  [INFO]  Port SIPT:0002. VChanIP set - 'line 0, chan 12'
  12:22:10.730152  [INFO]  Port SIPT:0002. Create msp-conn Conn[0003].
  12:22:10.730237  [INFO]  Conn[0003]: Set state 'Idle'->'AllocReq'
  12:22:10.730449  [INFO]  Port SIPT:0002. Set new IP Conn[0003] with chan[12]
  12:22:10.730570  [INFO]  Conn[0003]: set network interface in state 'AllocReq'. IP <2.2.2.2>. net-dev [1]
  12:22:10.730663  [INFO]  SIP. Callref 0002. IP-conn [0003] ready
  12:22:10.730746  [INFO]  SIP. Callref 0002. Media negotiate. Interface: 0
  12:22:10.730836  [INFO]  SIP. Callref 0002. Media negotiate <offer>. [1.1.1.1]. List-count: 1
  12:22:10.730937  [INFO]  SIP. Callref 0002. Media negotiate. Codec found: G711.A. PT 8. idx [0]. sendrecv
  12:22:10.731029  [INFO]  Port SIPT:0002. Set IP-conn [0003] recvonly
  12:22:10.731156  [INFO]  Conn[0003]: SendRecv set 'recvonly' in state 'AllocReq'
  12:22:10.731273  [INFO]  Conn[0003]: set rtp-cfg. <2.2.2.2:10004>:[02.31.52.63.74.80] <-> <0.0.0.0:0>:[00.00.00.00.00.00]. flg[11]
  12:22:10.731389  [INFO]  Port SIPT:0002. IP-conn [0003]. Set Tune
  12:22:10.731479  [INFO]  Conn[0003]: Set tune: vad 'off', cng 'off', ecan 'on'
			 pte [20], rtcp_period [0], rtcp_ctrl [0], dscp [0]
			 dtmf_mode 'inband' dtmf_pt rx [101] dtmf_pt tx [101] fax_mode [3]
			 use_vbd 'off' vbd_codec [2] vbd_pt [0]
			 NAT: N, t38transit: 0
  12:22:10.732061  [INFO]  SIP. Callref 0002. Use codec: G711.A sendrecv. PT 8.
  12:22:10.732239  [INFO]  Port SIPT:0002. IP-conn [0003]. Set Media-Info: [1.1.1.1:41652]
  12:22:10.732339  [INFO]  Conn[0003]: Set media-info in state 'AllocReq'. [1.1.1.1:41652]
  12:22:10.732433  [INFO]  Conn[0003]: [2.2.2.2:10004] <-> [1.1.1.1:41652]
  12:22:10.732517  [INFO]  Conn[0003]: Codec: G.711A , payload [8], DTMF-PT: 101
  12:22:10.732605  [INFO]  Conn[0003]: try get remote MAC for <1.1.1.1>
  12:22:10.732751  [INFO]  arp: failed to get route entry. but got default-gw addr <178.205.168.145>
  12:22:10.732904  [INFO]  Conn[0003]: MAC not found. Try ping
  12:22:10.733116  [INFO]  Conn[0003]: normal ping dest IP addr <1.1.1.1> (FOR FIND REMOTE MAC)
  12:22:10.733422  [INFO]  arp: failed to get route entry. but got default-gw addr <178.205.168.145>
  12:22:10.733528  [INFO]  Conn[0003]: MAC not found after ping
  12:22:10.733615  [INFO]  Conn[0003]: Set codecs-info in state 'AllocReq'. Count: 1
  12:22:10.733701  [INFO]  Conn[0003]: G711.A PT [8]
  12:22:10.733819  [INFO]  Port SIPT:0002. Dial Proc (state In-Dial, called len 11): <4952222222.> Compl.
  12:22:10.733912  [INFO]  Port SIPT:0002. Dial Proc. RADIUS return-code: [0].
  12:22:10.734005  [INFO]  Port SIPT:0002. Number tranzit: 'next digit' flag cleared
  12:22:10.734098  [INFO]  Port SIPT:0002. Set voice-chan from TDM-peer PRI:00:00. Chan 00:12
  12:22:10.734277  [INFO]  Q931. Line 00/00. Call 0004. TX 30
DUMP:	08.02.00.04.05.04.03.80.90.A3.18.03.A9.83.83.6C.
	0C.01.83.38.34.33.32.30.32.33.32.33.32.
		0x0004 (from orig), MSG=SETUP: 
		[bearer capability: 
			cap=speech
			std=CCITT
			rate=64 kbit/s
			mode=circuit
			layer1=G.711 A-law]
		[channel id: channel=3 (exclusive)] Coding=CCITT
		[calling party number: 4951111111 (type=unknown, plan=ISDN, present.allowed, network provided)]
---------------------------------
  12:22:10.734805  [INFO]  Q931. Line 00/00. Setup from SIPT:0002 (overlap)
  12:22:10.734921  [INFO]  Port PRI:00:00. Set state 'Wait-Ack'
  12:22:10.735019  [INFO]  Port PRI:00:00. Query Next Digit - peer SIPT:0002 (next.digit=n). Called len 11, proceed 00
  12:22:10.735108  [INFO]  Port PRI:00:00. Dial Out=4 (ext)
  12:22:10.735197  [INFO]  Port PRI:00:00. Check out-called-modify: '8'(1). ModTable[-1], CldMod[-1]
  12:22:10.735290  [INFO]  Q931. Line 00/00. Call 0004. 'Dial' in state 'Call Initiated'. <4>(1)
  12:22:10.735379  [INFO]  Port PRI:00:00. Dial Out: called len 00, append 01 (max digit 30)
  12:22:10.735467  [INFO]  Q931. Line 00/00. Call 0004. 'OutDial' result [0]
  12:22:10.735562  [INFO]  Port PRI:00:00. Query Next Digit - peer SIPT:0002 (next.digit=n). Called len 11, proceed 01
  12:22:10.735649  [INFO]  Port PRI:00:00. Dial Out=9 (ext)
  12:22:10.735736  [INFO]  Q931. Line 00/00. Call 0004. 'Dial' in state 'Call Initiated'. <9>(1)
  12:22:10.735823  [INFO]  Port PRI:00:00. Dial Out: called len 01, append 01 (max digit 30)
  12:22:10.735909  [INFO]  Q931. Line 00/00. Call 0004. 'OutDial' result [0]
  12:22:10.736018  [INFO]  Port PRI:00:00. Query Next Digit - peer SIPT:0002 (next.digit=n). Called len 11, proceed 02
  12:22:10.736107  [INFO]  Port PRI:00:00. Dial Out=5 (ext)
  12:22:10.736193  [INFO]  Q931. Line 00/00. Call 0004. 'Dial' in state 'Call Initiated'. <5>(1)
  12:22:10.736280  [INFO]  Port PRI:00:00. Dial Out: called len 02, append 01 (max digit 30)
  12:22:10.736365  [INFO]  Q931. Line 00/00. Call 0004. 'OutDial' result [0]
  12:22:10.736458  [INFO]  Port PRI:00:00. Query Next Digit - peer SIPT:0002 (next.digit=n). Called len 11, proceed 03
  12:22:10.736543  [INFO]  Port PRI:00:00. Dial Out=2 (ext)
  12:22:10.736627  [INFO]  Q931. Line 00/00. Call 0004. 'Dial' in state 'Call Initiated'. <2>(1)
  12:22:10.736713  [INFO]  Port PRI:00:00. Dial Out: called len 03, append 01 (max digit 30)
  12:22:10.736798  [INFO]  Q931. Line 00/00. Call 0004. 'OutDial' result [0]
  12:22:10.736892  [INFO]  Port PRI:00:00. Query Next Digit - peer SIPT:0002 (next.digit=n). Called len 11, proceed 04
  12:22:10.736979  [INFO]  Port PRI:00:00. Dial Out=02 (ext)
  12:22:10.737064  [INFO]  Q931. Line 00/00. Call 0004. 'Dial' in state 'Call Initiated'. <2>(1)
  12:22:10.737151  [INFO]  Port PRI:00:00. Dial Out: called len 04, append 01 (max digit 30)
  12:22:10.737235  [INFO]  Q931. Line 00/00. Call 0004. 'OutDial' result [0]
  12:22:10.737341  [INFO]  Port PRI:00:00. Query Next Digit - peer SIPT:0002 (next.digit=n). Called len 11, proceed 05
  12:22:10.737426  [INFO]  Port PRI:00:00. Dial Out=2 (ext)
  12:22:10.737511  [INFO]  Q931. Line 00/00. Call 0004. 'Dial' in state 'Call Initiated'. <2>(1)
  12:22:10.737655  [INFO]  Port PRI:00:00. Dial Out: called len 05, append 01 (max digit 30)
  12:22:10.737752  [INFO]  Q931. Line 00/00. Call 0004. 'OutDial' result [0]
  12:22:10.737849  [INFO]  Port PRI:00:00. Query Next Digit - peer SIPT:0002 (next.digit=n). Called len 11, proceed 06
  12:22:10.737936  [INFO]  Port PRI:00:00. Dial Out=2 (ext)
  12:22:10.738023  [INFO]  Q931. Line 00/00. Call 0004. 'Dial' in state 'Call Initiated'. <2>(1)
  12:22:10.738111  [INFO]  Port PRI:00:00. Dial Out: called len 06, append 01 (max digit 30)
  12:22:10.738196  [INFO]  Q931. Line 00/00. Call 0004. 'OutDial' result [0]
  12:22:10.738290  [INFO]  Port PRI:00:00. Query Next Digit - peer SIPT:0002 (next.digit=n). Called len 11, proceed 07
  12:22:10.738377  [INFO]  Port PRI:00:00. Dial Out=2 (ext)
  12:22:10.738462  [INFO]  Q931. Line 00/00. Call 0004. 'Dial' in state 'Call Initiated'. <2>(1)
  12:22:10.738549  [INFO]  Port PRI:00:00. Dial Out: called len 07, append 01 (max digit 30)
  12:22:10.738633  [INFO]  Q931. Line 00/00. Call 0004. 'OutDial' result [0]
  12:22:10.738739  [INFO]  Port PRI:00:00. Query Next Digit - peer SIPT:0002 (next.digit=n). Called len 11, proceed 08
  12:22:10.738827  [INFO]  Port PRI:00:00. Dial Out=2 (ext)
  12:22:10.738915  [INFO]  Q931. Line 00/00. Call 0004. 'Dial' in state 'Call Initiated'. <2>(1)
  12:22:10.739002  [INFO]  Port PRI:00:00. Dial Out: called len 08, append 01 (max digit 30)
  12:22:10.739087  [INFO]  Q931. Line 00/00. Call 0004. 'OutDial' result [0]
  12:22:10.739181  [INFO]  Port PRI:00:00. Query Next Digit - peer SIPT:0002 (next.digit=n). Called len 11, proceed 09
  12:22:10.739267  [INFO]  Port PRI:00:00. Dial Out=2. (ext)
  12:22:10.739353  [INFO]  Q931. Line 00/00. Call 0004. 'Dial' in state 'Call Initiated'. <2.>(2)
  12:22:10.739439  [INFO]  Port PRI:00:00. Dial Out: called len 09, append 02 (max digit 30)
  12:22:10.739525  [INFO]  Port PRI:00:00. Dial Out: 'End_of_Dial' digit/stop dial
  12:22:10.739607  [INFO]  Q931. Line 00/00. Call 0004. 'OutDial' result [2]
  12:22:10.739694  [INFO]  Port SIPT:0002. Prefix linked: called len 11, proceed 11
  12:22:10.739805  [INFO]  Conn[0003]: Set state 'AllocReq'->'Allocated'
  12:22:10.739986  [INFO]  Conn[0003]: Set state 'Allocated'->'DisableReq'
  12:22:10.741307  [INFO]  Conn[0003]: Created (disabled). State 'DisableReq'
  12:22:10.741442  [INFO]  Conn[0003]: Set state 'DisableReq'->'Disabled'
  12:22:10.741540  [INFO]  Port SIPT:0002. Created IP-conn [0003]. in-call
  12:22:10.773521  [INFO]  Q931. Line 00/00. Call 0004. RX 10
DUMP:	08.02.80.04.02.18.03.A9.83.83.
		0x0004 (to orig), MSG=CALL PROCEEDING: 
		[channel id: channel=3 (exclusive)] Coding=CCITT
---------------------------------
  12:22:10.773799  [INFO]  Q931. Line 00/00. Call 0004. Timer 'T303' stop in state 'Call Initiated'
  12:22:10.773903  [INFO]  Port PRI:00:00. Setup Ack (peer=SIPT:0002)
  12:22:10.773994  [INFO]  Port PRI:00:00. Set state 'Out-Dial'
  12:22:10.774084  [INFO]  Port SIPT:0002. Setup Acked in state In-Dial. (peer=PRI:00:00)
  12:22:10.774172  [INFO]  Port SIPT:0002. LinkUp to PRI:00:00
  12:22:10.774259  [INFO]  Port SIPT:0002. Activate IP-conn [0003]
  12:22:10.774343  [INFO]  Conn[0003]: try activate. state 'Disabled'.has media
  12:22:10.774432  [INFO]  Q931. Line 00/00. Call 0004. Invoke overlap-dial. Number <4952222222>(10) # Complete
  12:22:10.774522  [INFO]  Q931. Line 00/00. Call 0004. Outgoing dial in state <Call Initiated>, disconnect
  12:22:10.774621  [INFO]  Port PRI:00:00. Release, cause 'Message not compatible with call state', dcl '0', loc '0', peer 'SIPT:0002'
  12:22:10.774718  [INFO]  Port PRI:00:00. Save CDR. disabled. [0x0]
  12:22:10.774823  [INFO]  Port PRI:00:00. Set state 'Release'
  12:22:10.774916  [INFO]  Port PRI:00:00. Release peer 'SIPT:0002', cause 'Message not compatible with call state', dcl '7', loc '0'
  12:22:10.775009  [INFO]  Port SIPT:0002. Disconnect from PRI:00:00, cause 'Message not compatible with call state', dcl '7', loc '0'
  12:22:10.775166  [INFO]  Port SIPT:0002. Disconnect, cause 'Message not compatible with call state', dcl '7', loc '0'
  12:22:10.775276  [INFO]  Port SIPT:0002. Save CDR. disabled. [0x0]
  12:22:10.775364  [INFO]  Port SIPT:0002. Set signal: 'BUSY'
  12:22:10.775459  [INFO]  SIP. Callref 0002. Release, cause 'Message not compatible with call state', loc '0'
  12:22:10.775538  [INFO]  SIP. Callref 0002. Disconnect. cause 'Message not compatible with call state', loc '0', reply '0'
  12:22:10.775626  [INFO]  Port SIPT:0002. Deactivate IP-conn [0003]
  12:22:10.775707  [INFO]  Conn[0003]: Deactivate in state 'Disabled'
  12:22:10.775796  [INFO]  Port SIPT:0002. Release, cause 'Message not compatible with call state', dcl '0', loc '0'
  12:22:10.775882  [INFO]  Port SIPT:0002. Save CDR. disabled. [0x0]
  12:22:10.775967  [INFO]  Port SIPT:0002. Set state 'Release'
  12:22:10.776049  [INFO]  Port SIPT:0002. Free IP-conn [0003]
  12:22:10.776134  [INFO]  Conn[0003]: Destroy in state 'Disabled'. [check]
  12:22:10.776228  [INFO]  Port SIPT:0002. Destroy Conn[0003].
  12:22:10.776308  [INFO]  Conn[0003]: Set state 'Disabled'->'DeallocReq'
  12:22:10.776615  [INFO]  SIP. Callref 0002. New state 'SIPT_RELEASE_REQUEST'
  12:22:10.776738  [INFO]  SIP. Callref 0002. Disconnect
  12:22:10.776844  [INFO]  PBXIPC-SIP. TX. rel-req 
  12:22:10.776925  [INFO]  		# callref 0002
  12:22:10.777040  [INFO]  		# q850-cause 101
  12:22:10.777125  [INFO]  		# cause 500
  12:22:10.777477  [INFO]  Q931. Line 00/00. Call 0004. State 'Disconnect Request'
  12:22:10.777609  [INFO]  Q931. Line 00/00. Call 0004. Timer 'T305' (30 sec) start in state 'Disconnect Request'
  12:22:10.777745  [INFO]  Q931. Line 00/00. Call 0004. TX 10
DUMP:	08.02.00.04.45.08.02.81.E5.
		0x0004 (from orig), MSG=DISCONNECT: 
		[cause: Message not compatible with call state (location=private network serving local user, std=CCITT)]
---------------------------------
  12:22:10.778013  [INFO]  Q931. Line 00/00. Call 0004. State 'Outgoing Call Proceeding'
  12:22:10.778187  [INFO]  Q931. Line 00/00. Call 0004. RX 9
DUMP:	08.02.80.04.01.1E.02.81.88.
		0x0004 (to orig), MSG=ALERTING: 
		[progress ind: In-band signal]
---------------------------------
  12:22:10.778508  [INFO]  Q931. Line 00/00. Call 0004. Timer 'T305' stop in state 'Outgoing Call Proceeding'
  12:22:10.778620  [INFO]  Q931. Line 00/00. Call 0004. Timer 'T301' (180 sec) start in state 'Outgoing Call Proceeding'
  12:22:10.778712  [INFO]  Q931. Line 00/00. Call 0004. State 'Call Delivered'
  12:22:10.778800  [INFO]  Q931. Line 00/00. Call 0004. SetISDN: allway[1], fwd_acs[0]. Stage [0]
  12:22:10.778886  [INFO]  Q931. Line 00/00. Call 0004. Switch v-chan
  12:22:10.778973  [INFO]  Port PRI:00:00. In-band message indication. Stage [0]. Cause: 'none' (outgoing)
  12:22:10.779065  [INFO]  Port PRI:00:00. Release, cause 'none', dcl '0', loc '0'
  12:22:10.779153  [INFO]  Port PRI:00:00. Save CDR. disabled. [0x0]
  12:22:10.779243  [INFO]  Port PRI:00:00. Disconnect, cause 'none', dcl '0', loc '0'
  12:22:10.779350  [INFO]  Port PRI:00:00. Save CDR. disabled. [0x0]
  12:22:10.779438  [INFO]  Port PRI:00:00. Set signal: 'BUSY'
  12:22:10.779529  [INFO]  Q931. Line 00/00. Call 0004. State 'Disconnect Request'
  12:22:10.779615  [INFO]  Q931. Line 00/00. Call 0004. Timer 'T305' (30 sec) start in state 'Disconnect Request'
  12:22:10.779724  [INFO]  Q931. Line 00/00. Call 0004. TX 6
DUMP:	08.02.00.04.45.
		0x0004 (from orig), MSG=DISCONNECT: 
---------------------------------
  12:22:10.779915  [ERR ]  Port PRI:00:00. Alert (without peer)
  12:22:10.780012  [INFO]  Port PRI:00:00. Disconnect, cause 'Temporary failure', dcl '0', loc '0'
  12:22:10.780107  [INFO]  Port PRI:00:00. Save CDR. disabled. [0x0]
  12:22:10.780195  [INFO]  Port PRI:00:00. Set signal: 'BUSY'
  12:22:10.780284  [INFO]  Q931. Line 00/00. Call 0004. State 'Disconnect Request'
  12:22:10.780370  [INFO]  Q931. Line 00/00. Call 0004. Timer 'T305' (30 sec) start in state 'Disconnect Request'
  12:22:10.780543  [INFO]  Q931. Line 00/00. Call 0004. TX 10
DUMP:	08.02.00.04.45.08.02.80.A9.
		0x0004 (from orig), MSG=DISCONNECT: 
		[cause: Temporary failure (location=user, std=CCITT)]
---------------------------------
  12:22:10.780812  [INFO]  Conn[0003]: Destroyed
  12:22:10.780937  [INFO]  Conn[0003]: clr rtp-cfg. <2.2.2.2:10004>:[02.31.52.63.74.80] <-> <1.1.1.1:41652>:[00.00.00.00.00.00]. flg[11]
  12:22:10.781039  [INFO]  Conn[0003]: Set state 'DeallocReq'->'Idle'
  12:22:10.782474  [INFO]  PBXIPC-SIP. RX. rel-cplt 
  12:22:10.782624  [INFO]  		# callref 0002
  12:22:10.782710  [INFO]  		# cause 500
  12:22:10.782858  [INFO]  SIP. Callref 0002. Release complete
  12:22:10.782962  [INFO]  SIP. Callref 0002. New state 'SIPT_IDLE'
  12:22:10.783060  [INFO]  Port SIPT:0002. Release Complete, cause 'Temporary failure', dcl '0', loc '0'
  12:22:10.783159  [INFO]  Port SIPT:0002. Save CDR. disabled. [0x0]
  12:22:10.783262  [INFO]  Port SIPT:0002. Set state 'Ready'
  12:22:10.806950  [INFO]  Q931. Line 00/00. Call 0004. RX 5
DUMP:	08.02.80.04.4D.
		0x0004 (to orig), MSG=RELEASE: 
---------------------------------
  12:22:10.807247  [INFO]  Q931. Line 00/00. Call 0004. Recv 'Release'
  12:22:10.807359  [INFO]  Q931. Line 00/00. Call 0004. TX 6
DUMP:	08.02.00.04.5A.
		0x0004 (from orig), MSG=RELEASE COMPLETE: 
---------------------------------
  12:22:10.807562  [INFO]  Port PRI:00:00. Release Complete, cause 'Normal, unspecified', dcl '0', loc '0'
  12:22:10.807676  [INFO]  Port PRI:00:00. Save CDR. disabled. [0x0]
  12:22:10.807785  [INFO]  Port PRI:00:00. Set state 'Ready'
  12:22:10.807878  [INFO]  Q931. Line 00/00. Call 0004. State 'Null'
SMG-2. SN: VI45000362 Software Version: V.3.1.1.1089. 2/S Build: Jun 30 2015 10:43:35
Log stopped at:  12:22:16.266410
